[01/06 14:43:50,Telerik.TestStudio.ExecutionManagerService.exe(2532:46),Execution] JobBroker.ScheduleJobAsync() : Job satisfied all preconditions, id = a53406ce-f58f-4d95-a494-0b9e36b21b9a
[01/06 14:43:50,Telerik.TestStudio.ExecutionManagerService.exe(2532:46),Execution] JobBroker.ScheduleJobAsync() : Job sent to scheduler, id = a53406ce-f58f-4d95-a494-0b9e36b21b9a
[01/06 14:43:50,Telerik.TestStudio.ExecutionManagerService.exe(2532:46),Execution] JobsController.CreateNewJob() : Accepted new job, Id = a53406ce-f58f-4d95-a494-0b9e36b21b9a
[01/06 14:43:56,Telerik.TestStudio.ExecutionManagerService.exe(2532:23),Execution] JobRunner.RunJobAsync() : TestList loaded for job ID = a53406ce-f58f-4d95-a494-0b9e36b21b9a
[01/06 14:43:56,Telerik.TestStudio.ExecutionManagerService.exe(2532:23),Execution] JobRunner.RunJobAsync() : Job started, ID = a53406ce-f58f-4d95-a494-0b9e36b21b9a
[01/06 14:43:57,Telerik.TestStudio.RemoteExecutor.exe(2496:44),Execution] RunningTestsController.StartTest() : Test run requested for Cloud path=I:\Application Development Team\TestStudio\briar-tests\Regression Tests
[01/06 14:43:58,Telerik.TestStudio.RemoteExecutor.exe(2496:34)] First trace message from pool unnamed thread (managed ID = 34, native ID = 5008).
[01/06 14:43:58,Telerik.TestStudio.RemoteExecutor.exe(2496:34),Warning] CloudStorageProjectOpener.CheckForExistingFile() : Warning- File C:\Users\bpadmin\AppData\Local\Temp\2\Projects\ef2b80f8-f595-4ab4-baf0-0187315b694f\Regression Tests\TestLists\Stations NWFWMD.aiilist already exisits, and an attempt was made to overwrite
[01/06 14:43:58,Telerik.TestStudio.RemoteExecutor.exe(2496:34),Warning] CloudStorageProjectOpener.CheckForExistingFile() : Warning- File C:\Users\bpadmin\AppData\Local\Temp\2\Projects\ef2b80f8-f595-4ab4-baf0-0187315b694f\Regression Tests\TestLists\Test Run.aiilist already exisits, and an attempt was made to overwrite
[01/06 14:45:52,ArtOfTest.Runner.exe(1136:3)] First trace message from process 1136: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=3532
[01/06 14:45:52,ArtOfTest.Runner.exe(1136:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 1880).
[01/06 14:45:52,ArtOfTest.Runner.exe(1136:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[01/06 14:45:53,ArtOfTest.Runner.exe(1136:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[01/06 14:45:53,ArtOfTest.Runner.exe(1136:5)] First trace message from pool unnamed thread (managed ID = 5, native ID = 1856).
[01/06 14:45:53,ArtOfTest.Runner.exe(1136:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=3792, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=2359712)...
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] Connector.Start() : Process launched (ID=3592, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 5637320").
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] Connector.Start() : Process exited (ID=3592, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 5637320").
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=2496) is assumed to host the controller.
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] Connector.InjectCode() : ------- Function Start -------
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?2496
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 5637320 on another thread...
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 4236).
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:9),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[01/06 14:45:54,ArtOfTest.Runner.exe(1136:5),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 5637320 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?2496")...
[01/06 14:45:54,IEXPLORE.EXE(3128:1)] First trace message from process 3128: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:3792 CREDAT:209921 /prefetch:2
[01/06 14:45:54,IEXPLORE.EXE(3128:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 4264).
[01/06 14:45:54,IEXPLORE.EXE(3128:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 5637320, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-829767088", PID = 2496).
[01/06 14:45:54,IEXPLORE.EXE(3128:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 5637320 on same thread...
[01/06 14:45:55,IEXPLORE.EXE(3128:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[01/06 14:45:55,IEXPLORE.EXE(3128:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_d37a6dd2-7bb6-4527-8acc-4153eec8a254".
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:6)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 6, native ID = 1440).
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:11)] First trace message from background thread "Remoted async command listener" (managed ID = 11, native ID = 4084).
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:11),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[01/06 14:45:55,IEXPLORE.EXE(3128:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "3e0ae141-1f3c-443f-8a19-25c3e6aa007e.CommandPipe", Async = "3e0ae141-1f3c-443f-8a19-25c3e6aa007e.AsyncPipe"}
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:11),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:11),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Framework] Connector.InjectCode() : ------- Function End -------
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_d37a6dd2-7bb6-4527-8acc-4153eec8a254" (now there is/are 1 total).
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Forced Error', path: 'Forced Error.tstest.'
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Got 9 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[01/06 14:45:55,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:45:56,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : 'http://nwtest/nwereg'"...
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : 'http://nwtest/nwereg'".
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Enter text 'bparmer' in 'MyFormUserIdText'"...
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Enter text 'bparmer' in 'MyFormUserIdText'".
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'bparmer' in 'MyFormPsswdPassword'"...
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'bparmer' in 'MyFormPsswdPassword'".
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Click 'MyFormIdJsp2Image'"...
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Click 'MyFormIdJsp2Image'".
[01/06 14:45:57,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:45:59,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Enter text '36743' in 'HPrmtText'"...
[01/06 14:45:59,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Enter text '36743' in 'HPrmtText'".
[01/06 14:45:59,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:45:59,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'GobuttonImage'"...
[01/06 14:46:00,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'GobuttonImage'".
[01/06 14:46:00,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:46:00,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Connect to pop-up window : 'http://nwtest/nwereg/faces/jsp/review/rvwTSRSmmry.jsp?offclPrmtId=36743&seqNo=1&_cid=prmtWindow_p_36743_1&_create=true'"...
[01/06 14:46:00,IEXPLORE.EXE(3128:1),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=4064454, AssemblyAndClass="C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-829767088", ProcessId="2496").
[01/06 14:46:01,IEXPLORE.EXE(3128:7)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 7, native ID = 3936).
[01/06 14:46:01,IEXPLORE.EXE(3128:7),Error] ProcessExtensions.GetEntryModulePath() : EXCEPTION! (see below)
     Outer Exception Type: Telerik.TestingFramework.Interop.Win32.Win32Exception
     Message: ERROR_GEN_FAILURE (31) : A device attached to the system is not functioning
     HRESULT: 0x80004005 (Official ID (if app.) = E_FAIL, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16389)
     Call Stack:
          at Telerik.TestingFramework.Interop.Win32.KERNEL32.QueryFullProcessImageName(HANDLE hProcess, QueryFullProcessImageNameFlags dwFlags, Int32 iTryBufferLength)
          at ArtOfTest.Common.Extensions.ProcessExtensions.GetEntryModulePath(Process process)

[01/06 14:46:01,IEXPLORE.EXE(3128:7),Framework] Connector.Start() : Process launched (ID=4128, Path="", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1769894").
[01/06 14:46:01,IEXPLORE.EXE(3128:7),Error] ProcessExtensions.GetEntryModulePath() : EXCEPTION! (see below)
     Outer Exception Type: Telerik.TestingFramework.Interop.Win32.Win32Exception
     Message: ERROR_GEN_FAILURE (31) : A device attached to the system is not functioning
     HRESULT: 0x80004005 (Official ID (if app.) = E_FAIL, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16389)
     Call Stack:
          at Telerik.TestingFramework.Interop.Win32.KERNEL32.QueryFullProcessImageName(HANDLE hProcess, QueryFullProcessImageNameFlags dwFlags, Int32 iTryBufferLength)
          at ArtOfTest.Common.Extensions.ProcessExtensions.GetEntryModulePath(Process process)

[01/06 14:46:01,IEXPLORE.EXE(3128:7),Framework] Connector.Start() : Process exited (ID=4128, ExitCode=0, Path="", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 1769894").
[01/06 14:46:01,IEXPLORE.EXE(3128:7),Framework] Connector.InjectCode() : ------- Function Start -------
[01/06 14:46:02,IEXPLORE.EXE(3128:7),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?2496
[01/06 14:46:02,IEXPLORE.EXE(3128:7),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1769894 on another thread...
[01/06 14:46:02,IEXPLORE.EXE(3128:8)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 8, native ID = 3992).
[01/06 14:46:02,IEXPLORE.EXE(3128:8),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[01/06 14:46:02,IEXPLORE.EXE(3128:7),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[01/06 14:46:02,IEXPLORE.EXE(3128:7),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 1769894 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?2496")...
[01/06 14:46:02,IEXPLORE.EXE(3128:11)] First trace message from background unnamed thread (managed ID = 11, native ID = 4472).
[01/06 14:46:02,IEXPLORE.EXE(3128:11),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 1769894, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-829767088", PID = 2496).
[01/06 14:46:02,IEXPLORE.EXE(3128:11),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 1769894 on same thread...
[01/06 14:46:02,IEXPLORE.EXE(3128:11),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[01/06 14:46:02,IEXPLORE.EXE(3128:11),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_2e8da177-cdbf-45b8-8573-5d74dd6506c0".
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:7)] First trace message from background thread "Remoted async command listener" (managed ID = 7, native ID = 1860).
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[01/06 14:46:02,IEXPLORE.EXE(3128:11),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "283e0245-d3f0-4e2e-ba63-58dd8143232b.CommandPipe", Async = "283e0245-d3f0-4e2e-ba63-58dd8143232b.AsyncPipe"}
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:7),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[01/06 14:46:02,IEXPLORE.EXE(3128:7),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[01/06 14:46:02,IEXPLORE.EXE(3128:7),Framework] Connector.InjectCode() : ------- Function End -------
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_2e8da177-cdbf-45b8-8573-5d74dd6506c0" (now there is/are 2 total).
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Connect to pop-up window : 'http://nwtest/nwereg/faces/jsp/review/rvwTSRSmmry.jsp?offclPrmtId=36743&seqNo=1&_cid=prmtWindow_p_36743_1&_create=true'".
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:46:02,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Click 'Prmtmail6Link'"...
[01/06 14:46:03,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Click 'Prmtmail6Link'".
[01/06 14:46:03,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:46:05,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Click 'ButtonNewsentmailGifImage'"...
[01/06 14:46:06,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Click 'ButtonNewsentmailGifImage'".
[01/06 14:46:06,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:46:06,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Connect to pop-up window : 'http://nwtest/nwereg/faces/jsp/correspondence/sentMailEdit.jsp?curPrmtId=36743&callerWindow=mailInfo&cmplncItemId=0&newSentMail=true&_cid=prmtWindow_p_36743_1&_create=false'"...
[01/06 14:46:06,IEXPLORE.EXE(3128:11),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=7144776, AssemblyAndClass="C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_-829767088", ProcessId="2496").
[01/06 14:46:07,IEXPLORE.EXE(3128:8)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 8, native ID = 3992).
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] Connector.Start() : Process launched (ID=5060, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 984412").
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] Connector.Start() : Process exited (ID=5060, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 984412").
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] Connector.InjectCode() : ------- Function Start -------
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?2496
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 984412 on another thread...
[01/06 14:46:07,IEXPLORE.EXE(3128:7)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 7, native ID = 3936).
[01/06 14:46:07,IEXPLORE.EXE(3128:7),Framework] <>c__DisplayClassf.<WaitForDocument>b__8() : Attempting to get document from window handle
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 984412 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2015.3.1217.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?2496")...
[01/06 14:46:07,IEXPLORE.EXE(3128:10)] First trace message from background unnamed thread (managed ID = 10, native ID = 4576).
[01/06 14:46:07,IEXPLORE.EXE(3128:10),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 984412, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-829767088", PID = 2496).
[01/06 14:46:07,IEXPLORE.EXE(3128:10),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 984412 on same thread...
[01/06 14:46:07,IEXPLORE.EXE(3128:10),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[01/06 14:46:07,IEXPLORE.EXE(3128:10),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_1e1ebbab-c131-4bac-b3a7-e70387e05692".
[01/06 14:46:07,ArtOfTest.Runner.exe(1136:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[01/06 14:46:07,ArtOfTest.Runner.exe(1136:18)] First trace message from background thread "Remoted async command listener" (managed ID = 18, native ID = 2444).
[01/06 14:46:07,ArtOfTest.Runner.exe(1136:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[01/06 14:46:07,IEXPLORE.EXE(3128:10),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "60c7e745-f819-4837-b130-7f14da7e8c80.CommandPipe", Async = "60c7e745-f819-4837-b130-7f14da7e8c80.AsyncPipe"}
[01/06 14:46:07,ArtOfTest.Runner.exe(1136:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[01/06 14:46:07,ArtOfTest.Runner.exe(1136:18),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[01/06 14:46:07,IEXPLORE.EXE(3128:8),Framework] Connector.InjectCode() : ------- Function End -------
[01/06 14:46:08,ArtOfTest.Runner.exe(1136:6),Framework] Manager.AddNewBrowser() : Added client ID "Client_1e1ebbab-c131-4bac-b3a7-e70387e05692" (now there is/are 3 total).
[01/06 14:46:08,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Connect to pop-up window : 'http://nwtest/nwereg/faces/jsp/correspondence/sentMailEdit.jsp?curPrmtId=36743&callerWindow=mailInfo&cmplncItemId=0&newSentMail=true&_cid=prmtWindow_p_36743_1&_create=false'".
[01/06 14:46:08,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:46:08,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Select 'ByValue' option '41' on 'SentMailEditLtrTypeSelect'"...
[01/06 14:46:13,ArtOfTest.Runner.exe(1136:5),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Select 'ByValue' option '41' on 'SentMailEditLtrTypeSelect'".
[01/06 14:46:13,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),Error] ExecutionEngine.CatchExecuteStepException() : EXCEPTION! (see below)
     Outer Exception Type: ArtOfTest.Common.Design.Exceptions.ElementFindException
     Message: Unable to locate element. Details: Attempting to find [Html] element using
          Find logic
          (Html): [id 'Exact' ruleTpSelect] AND [tagname 'Exact' select]
          Unable to locate element. Search failed!
          Learn more about finding elements here:
          http://docs.telerik.com/teststudio/troubleshooting-guide/test-execution-problems-tg/unable-to-locate-element
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at ArtOfTest.WebAii.Design.Execution.ExecutionUtils.WaitForAllElements(IAutomationHost host, AutomationDescriptor descriptor, Int32 timeout)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.ExecuteStep(Int32 order)

[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-829767088" in order to unblock and eventually terminate it...
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:6),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_d37a6dd2-7bb6-4527-8acc-4153eec8a254" (2 remaining).
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 11).
[01/06 14:46:44,IEXPLORE.EXE(3128:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_d37a6dd2-7bb6-4527-8acc-4153eec8a254") to pipe server...
[01/06 14:46:44,IEXPLORE.EXE(3128:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[01/06 14:46:44,ArtOfTest.Runner.exe(1136:11),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[01/06 14:46:47,ArtOfTest.Runner.exe(1136:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[01/06 14:46:47,ArtOfTest.Runner.exe(1136:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_2e8da177-cdbf-45b8-8573-5d74dd6506c0" (1 remaining).
[01/06 14:46:47,ArtOfTest.Runner.exe(1136:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 7).
[01/06 14:46:47,IEXPLORE.EXE(3128:11),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_2e8da177-cdbf-45b8-8573-5d74dd6506c0") to pipe server...
[01/06 14:46:47,IEXPLORE.EXE(3128:11),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[01/06 14:46:47,ArtOfTest.Runner.exe(1136:7),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[01/06 14:46:50,ArtOfTest.Runner.exe(1136:5),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[01/06 14:46:50,ArtOfTest.Runner.exe(1136:5),Framework] Manager.RemoveBrowser() : Removed client ID "Client_1e1ebbab-c131-4bac-b3a7-e70387e05692" (0 remaining).
[01/06 14:46:50,ArtOfTest.Runner.exe(1136:5),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[01/06 14:46:50,ArtOfTest.Runner.exe(1136:5),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 18).
[01/06 14:46:50,IEXPLORE.EXE(3128:10),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_1e1ebbab-c131-4bac-b3a7-e70387e05692") to pipe server...
[01/06 14:46:50,IEXPLORE.EXE(3128:10),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[01/06 14:46:50,ArtOfTest.Runner.exe(1136:18),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[01/06 14:46:50,ArtOfTest.Runner.exe(1136:13)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 13, native ID = 3932).
[01/06 14:46:50,ArtOfTest.Runner.exe(1136:13),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[01/06 14:46:54,ArtOfTest.Runner.exe(1136:5),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[01/06 14:46:54,ArtOfTest.Runner.exe(1136:5),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[01/06 14:46:54,Telerik.TestStudio.RemoteExecutor.exe(2496:67)] First trace message from background thread "Callback Listener Thread" (managed ID = 67, native ID = 4556).
[01/06 14:46:54,Telerik.TestStudio.RemoteExecutor.exe(2496:67),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[01/06 14:46:54,Telerik.TestStudio.RemoteExecutor.exe(2496:67),TestStudio] AutomationHostState.LoadFailureImageFromDisk() : Invalid file path to load the bitmap from: C:\Users\bpadmin\AppData\Local\Temp\2\ 130965832145944568_files\Forced Error_0_ImageOnFailure.png
[01/06 14:46:54,Telerik.TestStudio.RemoteExecutor.exe(2496:67),TestStudio] AutomationHostState.LoadFailureDOMFromDisk() : Invalid file path to load the DOM from: C:\Users\bpadmin\AppData\Local\Temp\2\ 130965832145944568_files\Forced Error_0_DomTreeOnFailure.txt
[01/06 14:46:55,Telerik.TestStudio.RemoteExecutor.exe(2496:50),TestStudio] AutomationHostState.LoadFailureImageFromDisk() : Invalid file path to load the bitmap from: C:\Users\bpadmin\AppData\Local\Temp\2\ 130965832145944568_files\Forced Error_0_ImageOnFailure.png
[01/06 14:46:55,Telerik.TestStudio.RemoteExecutor.exe(2496:50),TestStudio] AutomationHostState.LoadFailureDOMFromDisk() : Invalid file path to load the DOM from: C:\Users\bpadmin\AppData\Local\Temp\2\ 130965832145944568_files\Forced Error_0_DomTreeOnFailure.txt
[01/06 14:46:59,Telerik.TestStudio.ExecutionManagerService.exe(2532:47),Execution] JobRunner.<CleanupTestRunsStatus>b__12() : TestList finished; updating dispatch group header job Id = a53406ce-f58f-4d95-a494-0b9e36b21b9a
[01/06 14:46:59,Telerik.TestStudio.ExecutionManagerService.exe(2532:47),Execution] JobRunner.<CleanupTestRunsStatus>b__12() : TestList finished; sending notifications
[01/06 14:46:59,Telerik.TestStudio.ExecutionManagerService.exe(2532:47),Execution] PostListExecutionEmailer.OnAfterTestListCompleted() : Attempting to send email for job a53406ce-f58f-4d95-a494-0b9e36b21b9a
[01/06 14:46:59,Telerik.TestStudio.ExecutionManagerService.exe(2532:47),TestStudio] AutomationHostState.LoadFailureImageFromDisk() : Invalid file path to load the bitmap from: C:\Windows\TEMP\ 130965832145944568_files\Forced Error_0_ImageOnFailure.png
[01/06 14:46:59,Telerik.TestStudio.ExecutionManagerService.exe(2532:47),TestStudio] AutomationHostState.LoadFailureDOMFromDisk() : Invalid file path to load the DOM from: C:\Windows\TEMP\ 130965832145944568_files\Forced Error_0_DomTreeOnFailure.txt
[01/06 14:47:58,Telerik.TestStudio.ExecutionManagerService.exe(2532:23),Execution] JobRunner.<CleanupTestRunsStatus>b__12() : TestList execution status expired- removing from list job id=a53406ce-f58f-4d95-a494-0b9e36b21b9a
